[INFO] cloning repository https://github.com/Amperstrand/ntag424
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Amperstrand/ntag424" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FAmperstrand%2Fntag424", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FAmperstrand%2Fntag424'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 17846aaf696908ae21b3309595d5bcc73e817422
[INFO] testing Amperstrand/ntag424 against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FAmperstrand%2Fntag424" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Amperstrand/ntag424
[INFO] finished tweaking git repo https://github.com/Amperstrand/ntag424
[INFO] tweaked toml for git repo https://github.com/Amperstrand/ntag424 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Amperstrand/ntag424 on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Amperstrand/ntag424 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 1 package to latest Rust 1.88.0 compatible version
[INFO] [stderr]       Adding log v0.4.32
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded dbl v0.5.0
[INFO] [stderr]   Downloaded inout v0.2.2
[INFO] [stderr]   Downloaded cipher v0.5.1
[INFO] [stderr]   Downloaded cpubits v0.1.0
[INFO] [stderr]   Downloaded p224 v0.13.2
[INFO] [stderr]   Downloaded aes v0.9.0
[INFO] [stderr]   Downloaded cmac v0.8.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0703dc14542a2ce0c502db2a7a685a957b2b34c0e6b668b2326626de0cce51a9
[INFO] running `Command { std: "docker" "start" "-a" "0703dc14542a2ce0c502db2a7a685a957b2b34c0e6b668b2326626de0cce51a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0703dc14542a2ce0c502db2a7a685a957b2b34c0e6b668b2326626de0cce51a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0703dc14542a2ce0c502db2a7a685a957b2b34c0e6b668b2326626de0cce51a9", kill_on_drop: false }`
[INFO] [stdout] 0703dc14542a2ce0c502db2a7a685a957b2b34c0e6b668b2326626de0cce51a9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 869ea0f41ee7b8bcae529fba9f9953f827c5ebe7dcb80cd8ec29d1a9d700d34b
[INFO] running `Command { std: "docker" "start" "-a" "869ea0f41ee7b8bcae529fba9f9953f827c5ebe7dcb80cd8ec29d1a9d700d34b", kill_on_drop: false }`
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling const-oid v0.9.6
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling base16ct v0.2.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling cpubits v0.1.0
[INFO] [stderr]    Compiling generic-array v0.14.9
[INFO] [stderr]    Compiling log v0.4.32
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling hybrid-array v0.4.10
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling inout v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling dbl v0.5.0
[INFO] [stderr]    Compiling cipher v0.5.1
[INFO] [stderr]    Compiling digest v0.11.2
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling aes v0.9.0
[INFO] [stderr]    Compiling cmac v0.8.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling p224 v0.13.2
[INFO] [stderr]    Compiling ntag424 v0.1.0-beta1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.35s
[INFO] running `Command { std: "docker" "inspect" "869ea0f41ee7b8bcae529fba9f9953f827c5ebe7dcb80cd8ec29d1a9d700d34b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "869ea0f41ee7b8bcae529fba9f9953f827c5ebe7dcb80cd8ec29d1a9d700d34b", kill_on_drop: false }`
[INFO] [stdout] 869ea0f41ee7b8bcae529fba9f9953f827c5ebe7dcb80cd8ec29d1a9d700d34b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f3c0401de4d7506c506a6b16653c206eb0f0e1de38ac2db9a821bb0b32265306
[INFO] running `Command { std: "docker" "start" "-a" "f3c0401de4d7506c506a6b16653c206eb0f0e1de38ac2db9a821bb0b32265306", kill_on_drop: false }`
[INFO] [stderr]    Compiling ntag424 v0.1.0-beta1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.73s
[INFO] running `Command { std: "docker" "inspect" "f3c0401de4d7506c506a6b16653c206eb0f0e1de38ac2db9a821bb0b32265306", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f3c0401de4d7506c506a6b16653c206eb0f0e1de38ac2db9a821bb0b32265306", kill_on_drop: false }`
[INFO] [stdout] f3c0401de4d7506c506a6b16653c206eb0f0e1de38ac2db9a821bb0b32265306
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d01038b8deaac5ad15860c7431f23cead92cd8664114cdc7228bede0a05a2fe3
[INFO] running `Command { std: "docker" "start" "-a" "d01038b8deaac5ad15860c7431f23cead92cd8664114cdc7228bede0a05a2fe3", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ntag424-5a00b5c3877a668b)
[INFO] [stdout] 
[INFO] [stdout] running 198 tests
[INFO] [stdout] test commands::authenticate::aes::tests::non_first_finish_auth_detects_wrong_rnd_a ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::non_first_finish_auth_hw_key0 ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::part2_apdu_and_finish_an12196_key0 ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::part2_apdu_and_finish_an12196_key3 ... ok
[INFO] [stdout] test commands::change_key::tests::change_key_case1_an12196_vector ... ok
[INFO] [stdout] test commands::change_key::tests::change_master_key_case2_an12196_vector ... ok
[INFO] [stdout] test commands::change_key::tests::crc32_matches_an12196_case1_vector ... ok
[INFO] [stdout] test commands::authenticate::lrp::tests::verify_detects_bad_picc_response ... ok
[INFO] [stdout] test commands::authenticate::lrp::tests::verify_detects_bad_pcdcap2_echo ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::non_first_finish_auth_an12196_table23 ... ok
[INFO] [stdout] test commands::change_file_settings::tests::change_file_settings_full_roundtrip ... ok
[INFO] [stdout] test commands::get_card_uid::tests::get_card_uid_hw_lrp ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::part2_apdu_and_finish_auth_hw_key0 ... ok
[INFO] [stdout] test commands::authenticate::lrp::tests::part2_apdu_and_verify_an12321_key3 ... ok
[INFO] [stdout] test commands::authenticate::lrp::tests::part2_apdu_and_verify_hw_key0 ... ok
[INFO] [stdout] test commands::change_file_settings::tests::change_file_settings_permission_denied ... ok
[INFO] [stdout] test commands::change_file_settings::tests::change_file_settings_no_sdm_roundtrip ... ok
[INFO] [stdout] test commands::authenticate::aes::tests::finish_auth_detects_wrong_rnd_a ... ok
[INFO] [stdout] test commands::get_card_uid::tests::get_card_uid_an12196_vector ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_counter_byte_order ... ok
[INFO] [stdout] test commands::get_card_uid::tests::get_card_uid_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_hw_aes_ndef_five_reads ... ok
[INFO] [stdout] test commands::get_file_settings::tests::get_file_settings_mac_an12196_vector ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_rejects_bad_mac ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_roundtrip ... ok
[INFO] [stdout] test commands::get_key_version::tests::get_key_version_roundtrip ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_hw_lrp_permission_denied ... ok
[INFO] [stdout] test commands::get_file_settings::tests::get_file_settings_plain_roundtrip ... ok
[INFO] [stdout] test commands::get_key_version::tests::get_key_version_hw_aes_keys_0_to_4 ... ok
[INFO] [stdout] test commands::get_file_settings::tests::get_file_settings_mac_hw_aes ... ok
[INFO] [stdout] test commands::get_key_version::tests::get_key_version_hw_lrp_keys_0_to_4 ... ok
[INFO] [stdout] test commands::get_file_counters::tests::get_file_counters_full_mode_roundtrip ... ok
[INFO] [stdout] test commands::get_key_version::tests::get_key_version_hw_aes_key0_ctr2 ... ok
[INFO] [stdout] test commands::get_key_version::tests::get_key_version_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::get_file_settings::tests::get_file_settings_mac_hw_lrp ... ok
[INFO] [stdout] test commands::get_version::tests::get_version_plain_roundtrip ... ok
[INFO] [stdout] test commands::get_version::tests::get_version_mac_hw_aes ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::reads_current_file_with_explicit_length ... ok
[INFO] [stdout] test commands::get_tt_status::tests::get_tt_status_rejects_unexpected_ciphertext_length ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::rejects_empty_buffer_without_transmit ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::rejects_overlong_response ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::rejects_oversized_offset_without_transmit ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::clamps_oversized_buffer_to_256 ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::encodes_15_bit_offset_across_p1_p2 ... ok
[INFO] [stdout] test commands::get_version::tests::get_version_mac_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::get_tt_status::tests::get_tt_status_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::get_tt_status::tests::get_tt_status_hw_lrp_open ... ok
[INFO] [stdout] test commands::get_version::tests::get_version_mac_roundtrip ... ok
[INFO] [stdout] test commands::get_version::tests::get_version_mac_hw_lrp ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::file_not_found_surfaces_as_error ... ok
[INFO] [stdout] test commands::get_tt_status::tests::get_tt_status_hw_lrp_invalid ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::short_file_id_selects_and_targets_ef ... ok
[INFO] [stdout] test commands::iso_read_binary::tests::security_status_not_satisfied_surfaces_as_error ... ok
[INFO] [stdout] test commands::iso_select_file::tests::select_surfaces_iso_error ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::fifteen_bit_offset_splits_across_p1_p2 ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::file_not_found_surfaces_as_error ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::iso_update_binary_an12196_vector ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::rejects_oversized_offset_without_transmit ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::security_status_not_satisfied_surfaces_as_error ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::short_file_id_encodes_sfid_in_p1 ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_bad_padding_is_unexpected_length_and_advances_counter ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_hw_aes ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_readback_hw_aes ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_roundtrip_decrypts_and_unpads ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_mac_roundtrip_advances_counter ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_frames_header_and_copies_payload ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_encodes_offset_and_length_little_endian ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_mac_hw_aes ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_hw_aes ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_rejects_oversized_offset_without_transmit ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_hw_lrp ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_rejects_empty_buffer_without_transmit ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_readback_hw_lrp ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_rejects_short_buffer_without_transmit ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_mac_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_plain_surfaces_permission_denied ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_full_hw_lrp ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_mac_hw_aes ... ok
[INFO] [stdout] test commands::secure_channel::tests::response_mac_mismatch_leaves_counter_untouched ... ok
[INFO] [stdout] test commands::secure_channel::tests::response_mac_mismatch_on_first_byte_leaves_counter_untouched ... ok
[INFO] [stdout] test commands::secure_channel::tests::send_mac_roundtrip_advances_counter ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_mac_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::secure_channel::tests::strip_m2_padding_edge_cases ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_plain_hw_aes ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_plain_hw_lrp ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_mac_roundtrip ... ok
[INFO] [stdout] test commands::iso_select_file::tests::select_ndef_application_issues_spec_apdu ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::rejects_empty_data_without_transmit ... ok
[INFO] [stdout] test commands::read_sig::tests::read_sig_mac_hw_lrp ... ok
[INFO] [stdout] test commands::read_data::tests::read_data_mac_hw_lrp ... ok
[INFO] [stdout] test commands::set_configuration::tests::set_configuration_enable_lrp_an12321_vector ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_full_an12196_vector ... ok
[INFO] [stdout] test commands::set_configuration::tests::set_configuration_multi_option_advances_counter_in_order ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_mac_rejects_bad_trailer ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_mac_hw_aes ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_mac_roundtrip_advances_counter ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_mac_hw_lrp ... ok
[INFO] [stdout] test commands::set_configuration::tests::set_configuration_random_uid_an12196_vector ... ok
[INFO] [stdout] test commands::set_configuration::tests::set_configuration_no_options_sends_nothing ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_full_block_aligned_gets_extra_padding_block ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_mac_rejects_apdu_body_overflow_without_transmit ... ok
[INFO] [stdout] test commands::secure_channel::tests::verify_response_mac_matches_get_card_uid_vector ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_full_hw_aes ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_full_hw_lrp ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_full_roundtrip_small_payload ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_encodes_offset_and_length_le ... ok
[INFO] [stdout] test commands::iso_update_binary::tests::rejects_apdu_body_overflow_without_transmit ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_rejects_apdu_body_overflow_without_transmit ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_rejects_empty_data_without_transmit ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_rejects_oversized_offset_without_transmit ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_surfaces_permission_denied ... ok
[INFO] [stdout] test commands::write_data::tests::write_data_plain_frames_header_and_data ... ok
[INFO] [stdout] test crypto::lrp::tests::updated_keys_an12304 ... ok
[INFO] [stdout] test crypto::suite::tests::aes_mac_mode_get_file_settings_an12196 ... ok
[INFO] [stdout] test crypto::suite::tests::aes_secure_messaging_write_data_an12196 ... ok
[INFO] [stdout] test crypto::suite::tests::aes_secure_messaging_get_uid_an12196 ... ok
[INFO] [stdout] test crypto::suite::tests::aes_secure_messaging_write_ndef_multiblock_an12196 ... ok
[INFO] [stdout] test crypto::lrp::tests::plaintexts_an12304 ... ok
[INFO] [stdout] test crypto::suite::tests::aes_session_keys_an12196 ... ok
[INFO] [stdout] test commands::secure_channel::tests::compute_cmd_mac_matches_get_file_settings_vector ... ok
[INFO] [stdout] test crypto::lrp::tests::cmac_an12304_vectors ... ok
[INFO] [stdout] test crypto::suite::tests::aes_session_keys_key0_an12196 ... ok
[INFO] [stdout] test crypto::tests::ct_eq_8_rejects_last_byte_mismatch ... ok
[INFO] [stdout] test crypto::suite::tests::lrp_session_keys_an12321 ... ok
[INFO] [stdout] test crypto::tests::ct_eq_8_rejects_first_byte_mismatch ... ok
[INFO] [stdout] test crypto::suite::tests::lrp_command_encrypt_decrypt_nfc_ev2_crypto ... ok
[INFO] [stdout] test crypto::lrp::tests::eval_an12304_vectors ... ok
[INFO] [stdout] test crypto::suite::tests::lrp_authenticate_first_exchange_an12321 ... ok
[INFO] [stdout] test crypto::suite::tests::lrp_stateful_get_uid_sequence_nfc_ev2_crypto ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_hw_key0_full_handshake ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_hw_key0_full_handshake_b ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_rejects_wrong_auth_mode ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_surfaces_picc_auth_error ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_an12321_key3_full_handshake ... ok
[INFO] [stdout] test session::tests::read_file_with_mode_plain_hw_lrp_advances_counter ... ok
[INFO] [stdout] test types::cc::tests::access_condition_byte_roundtrip ... ok
[INFO] [stdout] test types::cc::tests::builder_chain_changes_access ... ok
[INFO] [stdout] test types::cc::tests::decode_full_cc_file_from_hardware ... ok
[INFO] [stdout] test types::cc::tests::decode_ntag424_default ... ok
[INFO] [stdout] test types::cc::tests::default_matches_delivery_bytes ... ok
[INFO] [stdout] test types::cc::tests::roundtrip ... ok
[INFO] [stdout] test types::configuration::tests::build_emits_tag_tamper_in_table_50_order ... ok
[INFO] [stdout] test crypto::lrp::tests::lricb_an12304_vectors ... ok
[INFO] [stdout] test types::file_settings::tests::buffer_too_short_on_decode ... ok
[INFO] [stdout] test types::file_settings::tests::clear_tt_with_sdm_mac_keeps_meta_disabled ... ok
[INFO] [stdout] test session::tests::authenticate_lrp_non_first_hw_key0_full_handshake ... ok
[INFO] [stdout] test types::file_settings::tests::decode_an12196_get_file_settings ... ok
[INFO] [stdout] test types::file_settings::tests::decode_factory_file_settings_cc ... ok
[INFO] [stdout] test types::file_settings::tests::decode_factory_file_settings_ndef ... ok
[INFO] [stdout] test types::file_settings::tests::decode_rejects_file_option_reserved_bits ... ok
[INFO] [stdout] test types::file_settings::tests::decode_rejects_s19_ctr_ret_set_without_rctr_mirror ... ok
[INFO] [stdout] test types::file_settings::tests::decode_rejects_sdm_access_rights_high_nibble_not_f ... ok
[INFO] [stdout] test types::file_settings::tests::decode_rejects_sdm_options_ascii_bit_clear ... ok
[INFO] [stdout] test types::file_settings::tests::decode_round_trip_for_get_file_settings ... ok
[INFO] [stdout] test types::file_settings::tests::decode_round_trip_for_get_file_settings_with_tt_status ... ok
[INFO] [stdout] test crypto::tests::ct_eq_8_accepts_equal_inputs ... ok
[INFO] [stdout] test types::file_settings::tests::encode_change_file_settings_with_tt_status ... ok
[INFO] [stdout] test types::file_settings::tests::lrp_blob_just_before_mac_is_ok ... ok
[INFO] [stdout] test types::file_settings::tests::picc_blob_just_before_mac_is_ok ... ok
[INFO] [stdout] test types::file_settings::tests::read_ctr_limit_sentinel_decodes_as_none ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_enc_outside_mac_window ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_enc_requires_both_mirrors_rctr_only ... ok
[INFO] [stdout] test types::file_settings::tests::encode_an12196_change_file_settings ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_enc_requires_both_mirrors_uid_only ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_mac_input_after_mac ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_enc_and_rctr ... ok
[INFO] [stdout] test crypto::originality::tests::rejects_wrong_uid ... ok
[INFO] [stdout] test session::tests::read_file_plain_hw_aes_advances_counter ... ok
[INFO] [stdout] test types::configuration::tests::tag_tamper_builder_emits_option_07_payload ... ok
[INFO] [stdout] test types::file_settings::tests::decode_factory_file_settings_proprietary ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_rctr_and_tamper ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_enc_and_uid ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_rctr_and_mac ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_tamper_and_mac ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_uid_and_mac ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_uid_and_tamper ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_picc_blob_overlapping_mac_aes ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_picc_blob_overlapping_mac_lrp ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_tamper_in_ciphertext_half ... ok
[INFO] [stdout] test types::file_settings::tests::sdm_is_const_constructable ... ok
[INFO] [stdout] test types::file_settings::tests::tamper_at_last_byte_of_plaintext_half_is_ok ... ok
[INFO] [stdout] test types::file_settings::tests::try_new_enables_tt_status_mirroring ... ok
[INFO] [stdout] test types::version::tests::tag_tamper_detection_accepts_both_back_modulation_variants ... ok
[INFO] [stdout] test types::version::tests::tag_tamper_detection_looks_at_the_x8h_suffix_not_the_high_nibble ... ok
[INFO] [stdout] test types::version::tests::tag_tamper_detection_rejects_non_tt_low_nibbles ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_picc_blob_overlapping_tamper ... ok
[INFO] [stdout] test types::file_settings::tests::rejects_picc_blob_overlapping_enc_file_data ... ok
[INFO] [stdout] test crypto::suite::tests::aes_cbc_encrypt_rejects_empty_buffer - should panic ... ok
[INFO] [stdout] test crypto::suite::tests::aes_cbc_decrypt_rejects_non_block_buffer - should panic ... ok
[INFO] [stdout] test session::tests::authenticate_aes_an12196_key0_full_handshake ... FAILED
[INFO] [stdout] test session::tests::authenticate_aes_hw_key0_full_handshake_b ... FAILED
[INFO] [stdout] test session::tests::authenticate_aes_hw_key0_full_handshake ... FAILED
[INFO] [stdout] test session::tests::authenticate_aes_surfaces_picc_auth_error ... FAILED
[INFO] [stdout] test session::tests::authenticate_aes_non_first_an12196_table23_full_handshake ... FAILED
[INFO] [stdout] test session::tests::authenticate_aes_non_first_hw_key3 ... FAILED
[INFO] [stdout] test types::file_settings::tests::rejects_overlap_uid_and_rctr ... ok
[INFO] [stdout] test crypto::originality::tests::an12196_table30_vector ... ok
[INFO] [stdout] test crypto::originality::tests::rejects_flipped_signature ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_an12196_key0_full_handshake stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_an12196_key0_full_handshake' (154) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d090aef8 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_an12196_key0_full_handshake
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:50:19
[INFO] [stdout]   26:     0x5597d0905267 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_an12196_key0_full_handshake::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:22:50
[INFO] [stdout]   27:     0x5597d08b3186 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_an12196_key0_full_handshake::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_hw_key0_full_handshake_b stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_hw_key0_full_handshake_b' (156) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d0908fca - ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake_b
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:538:19
[INFO] [stdout]   26:     0x5597d09051a7 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake_b::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:516:47
[INFO] [stdout]   27:     0x5597d08b3086 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake_b::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_hw_key0_full_handshake stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_hw_key0_full_handshake' (155) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d090720a - ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:354:19
[INFO] [stdout]   26:     0x5597d09050e7 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:329:45
[INFO] [stdout]   27:     0x5597d08b2f86 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_hw_key0_full_handshake::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_surfaces_picc_auth_error stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_surfaces_picc_auth_error' (159) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d090976a - ntag424[59f6857982c55915]::session::tests::authenticate_aes_surfaces_picc_auth_error
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:96:18
[INFO] [stdout]   26:     0x5597d09051d7 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_surfaces_picc_auth_error::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:72:47
[INFO] [stdout]   27:     0x5597d08b30c6 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_surfaces_picc_auth_error::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_non_first_an12196_table23_full_handshake stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_non_first_an12196_table23_full_handshake' (157) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d090dcbc - ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_an12196_table23_full_handshake
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:297:19
[INFO] [stdout]   26:     0x5597d0905327 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_an12196_table23_full_handshake::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:250:63
[INFO] [stdout]   27:     0x5597d08b3286 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_an12196_table23_full_handshake::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session::tests::authenticate_aes_non_first_hw_key3 stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session::tests::authenticate_aes_non_first_hw_key3' (158) panicked at src/testing.rs:90:9:
[INFO] [stdout] assertion `left == right` failed: TestTransport: unexpected APDU
[INFO] [stdout]   left: [144, 113, 0, 0, 5, 0, 3, 0, 0, 0, 0]
[INFO] [stdout]  right: [144, 113, 0, 0, 2, 0, 0, 0]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5597d09dc2c8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5597d09dc2c8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5597d09dc2c8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5597d0a1eb6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5597d0a1eb6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5597d09e0ae2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5597d09e0ae2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5597d09bb476 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5597d09bb476 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5597d09d4819 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5597d09708cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5597d09708cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5597d09d49d2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5597d09d49d2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5597d09bb532 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5597d09b0739 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5597d09bc24d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5597d0a1f2bc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5597d0a1f1d3 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5597d08e602f - core[28b146924c2f19c6]::panicking::assert_failed::<&[u8], &[u8]>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5597d08f9b7f - <ntag424[59f6857982c55915]::testing::TestTransport as ntag424[59f6857982c55915]::transport::Transport>::transmit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:90:9
[INFO] [stdout]   22:     0x5597d0953529 - ntag424[59f6857982c55915]::commands::authenticate::aes::authenticate_ev2_first::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands/authenticate/aes.rs:90:46
[INFO] [stdout]   23:     0x5597d08f8443 - <ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes::<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/unauthenticated.rs:143:85
[INFO] [stdout]   24:     0x5597d08f11be - ntag424[59f6857982c55915]::testing::block_on::<<ntag424[59f6857982c55915]::session::Session<ntag424[59f6857982c55915]::session::unauthenticated::Unauthenticated>>::authenticate_aes<ntag424[59f6857982c55915]::testing::TestTransport>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/testing.rs:115:24
[INFO] [stdout]   25:     0x5597d09065c8 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_hw_key3
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:595:19
[INFO] [stdout]   26:     0x5597d09050b7 - ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_hw_key3::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/session/tests.rs:556:40
[INFO] [stdout]   27:     0x5597d08b2f46 - <ntag424[59f6857982c55915]::session::tests::authenticate_aes_non_first_hw_key3::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5597d09639ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5597d09639ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5597d0971225 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5597d0971225 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5597d0971225 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5597d0971225 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5597d0971225 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5597d096bd84 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5597d096bd84 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5597d09742a2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5597d09742a2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5597d09742a2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5597d09742a2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5597d09742a2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5597d09dbc2f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5597d09dbc2f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x7512c0d4eaa4 - <unknown>
[INFO] [stdout]   49:     0x7512c0ddba64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     session::tests::authenticate_aes_an12196_key0_full_handshake
[INFO] [stdout]     session::tests::authenticate_aes_hw_key0_full_handshake
[INFO] [stdout]     session::tests::authenticate_aes_hw_key0_full_handshake_b
[INFO] [stdout]     session::tests::authenticate_aes_non_first_an12196_table23_full_handshake
[INFO] [stdout]     session::tests::authenticate_aes_non_first_hw_key3
[INFO] [stdout]     session::tests::authenticate_aes_surfaces_picc_auth_error
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 192 passed; 6 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "d01038b8deaac5ad15860c7431f23cead92cd8664114cdc7228bede0a05a2fe3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d01038b8deaac5ad15860c7431f23cead92cd8664114cdc7228bede0a05a2fe3", kill_on_drop: false }`
[INFO] [stdout] d01038b8deaac5ad15860c7431f23cead92cd8664114cdc7228bede0a05a2fe3
